View Issue Details

IDProjectCategoryView StatusLast Update
0001671T99X171.00 SKB EagleSW Issuepublic2023-04-21 15:49
Reporter(ALTech) JunGyu Kim Assigned To(SW) Peter ChenDue Date2023-03-09 16:06
PriorityurgentSeveritys4-minorReproducibilitysometimes
Status closedResolutionreopened 
Summary0001671: [Smart3][ATV12] Sometimes NUGU voice recognition does not work
DescriptionTest procedure :
1. Pairing RCU
2. Enter the NUGU key of RCU
3. vocalization to MIC of RCU

Occasionally, NUGU recognition does not work.
If this symptom occurs even once, voice recognition is not possible without re-pairing the RCU.
(When rebooting with the RCU paired, this issue is relatively reproducible but not 100%.)

I checked the log and the device of adev_open_input_stream is being changed during normal/abnormal operation.
================== Normal (NUGU is working) ==================
02-23 18:37:19.298 4710 7916 D MenuNavigationData: startWatchingEdidFile() file is not exists. FileObserver stop watching
02-23 18:37:19.312 3488 3533 D audio_hw_primary: adev_open_input_stream: enter: devices(0x80000010) channel_mask(0x10) rate(16000) format(0x1) source(20)
02-23 18:37:19.312 3488 3533 D audio_hw_primary: check_input_parameters(sample_rate=16000, format=1, channel_count=1, devices = 80000010)
02-23 18:37:19.312 3488 3533 I audio_hw_primary: adev_open_input_stream: use RC audio HAL
02-23 18:37:19.312 3488 3533 D AudioHAL:audio_hal_thunks: rc_open_input_stream++:
02-23 18:37:19.312 3488 3533 I AudioHAL:AudioHardwareInput: openInputStream: ++
02-23 18:37:19.313 3488 3533 D AudioHAL:AudioHardwareInput: AudioHardwareInput::getBestDevice chose #0
02-23 18:37:19.313 3488 3533 I AudioHAL:AudioHardwareInput: openInputStream: –
02-23 18:37:19.313 3488 3533 D AudioHAL:audio_hal_thunks: rc_open_input_stream--, ret=0
02-23 18:37:19.313 3488 3533 D audio_hw_primary: adev_open_input_stream: exit
02-23 18:37:19.316 3558 7920 I AudioFlinger: AudioFlinger's thread 0xeede5f20 tid=7920 ready to run
02-23 18:37:19.319 3558 6622 D AudioPolicyService: not monitoring app op for uid 10031 and source 20
===========================================




================== Abormal (NUGU is NOT working) ==================
02-23 18:35:45.330 4710 7781 D MenuNavigationData: startWatchingEdidFile() file is not exists. FileObserver stop watching
02-23 18:35:45.341 3488 4756 D audio_hw_primary: adev_open_input_stream: enter: devices(0x80000004) channel_mask(0x10) rate(16000) format(0x3) source(20)
02-23 18:35:45.342 3488 4756 D audio_hw_primary: check_input_parameters(sample_rate=16000, format=3, channel_count=1, devices = 80000004)
02-23 18:35:45.342 3488 4756 D audio_hw_primary: adev_open_input_stream: in->requested_rate = 16000, in->config.rate = 48000
02-23 18:35:45.342 3488 4756 D audio_hw_primary: adev_open_input_stream: exit
02-23 18:35:45.345 3488 4756 D audio_hw_primary: in_get_buffer_size: enter: channel_mask(0x10) rate(16000) format(0x3)
02-23 18:35:45.345 3488 4756 D audio_hw_primary: get_input_buffer_size(sample_rate=16000, format=3, channel_count=1)
02-23 18:35:45.345 3488 4756 D audio_hw_primary: in_get_buffer_size: exit: buffer_size = 4096
02-23 18:35:45.345 3488 4756 D audio_hw_primary: in_get_buffer_size: enter: channel_mask(0x10) rate(16000) format(0x3)
02-23 18:35:45.345 3488 4756 D audio_hw_primary: get_input_buffer_size(sample_rate=16000, format=3, channel_count=1)
02-23 18:35:45.345 3488 4756 D audio_hw_primary: in_get_buffer_size: exit: buffer_size = 4096
02-23 18:35:45.346 3558 7783 I AudioFlinger: AudioFlinger's thread 0xeede5f20 tid=7783 ready to run
02-23 18:35:45.347 3558 3680 D AudioPolicyService: not monitoring app op for uid 10031 and source 20
============================================

Please check why device id is changed.

Thank you.
Kim
TagsNo tags attached.
Attach Tags

Users monitoring this issue

Activities

(ALTech) JunGyu Kim

2023-03-02 18:59

developer   ~0012410

Last edited: 2023-03-02 18:59

Hi Peter,

Additionally, Please also check "avc: denied" log.
This log is being print at 1 second intervals,.

02-23 18:37:20.032 3202 3202 E SELinux : avc: denied { find } for interface=vendor.amlogic.hardware.remotecontrol::IRemoteControl sid=u:r:hal_audio_default:s0 pid=3488 scontext=u:r:hal_audio_default:s0 tcontext=u:object_r:remotecontrol_hwservice:s0 tclass=hwservice_manager permissive=0

Thank you.
Kim

(SW) Peter Chen

2023-03-03 11:43

developer   ~0012416

Hi JunGyu,

Can you share the full log for this issue?

Thanks,
Peter

(ALTech) JunGyu Kim

2023-03-03 13:58

developer   ~0012423

Hi Peter,

I attached full log file. Please check it.

Thank you
Kim
NUGU_Not_Working.log (3,897,820 bytes)
NUGU_Working.log (3,845,093 bytes)

(ALTech) JunGyu Kim

2023-03-08 10:31

developer   ~0012462

Hi Peter,

Please let me know any update of this issue.

Thank you.
Kim

(SW) Peter Chen

2023-03-13 16:13

developer   ~0012516

Hi JunGyu,

It's look like the audio input device is not correct.
It will set audio input device for NUGU when RCU BT connected but sometimes it's too late to add the audio input device for NUGU.
I fixed this issue with checking the audio input device is added for NUGU.

Thanks,
Peter

(ALTech) JunGyu Kim

2023-03-14 12:11

developer   ~0012525

Hi Peter,

Thanks for explanation.
I will let you know if any problem.

I have one question, the denied message was also fixed? This log is printing too much.

02-23 18:37:20.032 3202 3202 E SELinux : avc: denied { find } for interface=vendor.amlogic.hardware.remotecontrol::IRemoteControl sid=u:r:hal_audio_default:s0 pid=3488 scontext=u:r:hal_audio_default:s0 tcontext=u:object_r:remotecontrol_hwservice:s0 tclass=hwservice_manager permissive=0

Thank you.
Kim

(SW) Peter Chen

2023-03-14 16:43

developer   ~0012532

Hi JunGyu,

The denied message has been existed from ATV10, it's useless.

Thanks,
Peter

(ALTech) JunGyu Kim

2023-03-15 15:42

developer   ~0012541

Hi Peter,

Our QA tested this issue but this issue is still reproducing.

The reproduce step is not clear.
I checked the log and the device id is changing as below.

// press NUGU
03-15 16:21:07.230 3810 3946 D WindowManager: keycode : 396 - processed. by skb function key

// input device is changed
03-15 16:21:07.246 7938 7966 D audio_hw_primary: adev_open_input_stream: enter: devices(0x80000080) channel_mask(0xc) rate(48000) format(0x1) source(1)
03-15 16:21:07.257 7938 7966 D audio_hw_primary: adev_open_input_stream: enter: devices(0x90000000) channel_mask(0xc) rate(48000) format(0x3) source(1)

I attached full log file.
Please check it again.

Thank you.
Kim
NUGU_not_working2_logcat.log (8,926,475 bytes)

(SW) Peter Chen

2023-03-16 10:23

developer   ~0012550

Hi JunGyu,

I check the log and the input device is correct before the audio service crashed.
Please check how to reproduce this.

// audio input device is correct
03-15 16:20:43.270 3513 3557 D audio_hw_primary: adev_open_input_stream: enter: devices(0x80000010) channel_mask(0x10) rate(16000) format(0x1) source(20)

--------- beginning of crash
03-15 16:21:05.432 7914 7914 F DEBUG : *** *** *** *** *** *** *** *** *** *** *** *** *** *** *** ***
03-15 16:21:05.432 7914 7914 F DEBUG : Build fingerprint: 'SKB/BFX_AT100/BFX-AT100:12/STTC.220724.001/15.537.24-20230315:userdebug/release-keys'
03-15 16:21:05.432 7914 7914 F DEBUG : Revision: '0'
03-15 16:21:05.432 7914 7914 F DEBUG : ABI: 'arm'
03-15 16:21:05.432 7914 7914 F DEBUG : Timestamp: 2023-03-15 16:21:05.289296453+0900
03-15 16:21:05.432 7914 7914 F DEBUG : Process uptime: 0s
03-15 16:21:05.432 7914 7914 F DEBUG : Cmdline: /vendor/bin/hw/android.hardware.audio.service-droidlogic
03-15 16:21:05.432 7914 7914 F DEBUG : pid: 3513, tid: 3513, name: audio.service-d >>> /vendor/bin/hw/android.hardware.audio.service-droidlogic <<<
03-15 16:21:05.432 7914 7914 F DEBUG : uid: 1041
03-15 16:21:05.432 7914 7914 F DEBUG : signal 35 (<debuggerd signal>), code -1 (SI_QUEUE from pid 3579, uid 1041), fault addr --------
03-15 16:21:05.432 7914 7914 F DEBUG : r0 00000006 r1 c0306201 r2 ff88ce90 r3 ff88cdd4
03-15 16:21:05.432 7914 7914 F DEBUG : r4 f4c1a138 r5 c0306201 r6 ff88ce90 r7 00000036
03-15 16:21:05.432 7914 7914 F DEBUG : r8 f43c0888 r9 f43c0844 r10 f4c1a138 r11 ff88ce90
03-15 16:21:05.432 7914 7914 F DEBUG : ip 00000100 sp ff88cdc0 lr f4bc8cbb pc f4bfaebc
03-15 16:21:05.432 7914 7914 F DEBUG : backtrace:
03-15 16:21:05.432 7914 7914 F DEBUG : #00 pc 00070ebc /apex/com.android.runtime/lib/bionic/libc.so (__ioctl+8) (BuildId: 065f5a7b98a8052e1c4af4e537983a0f)
03-15 16:21:05.432 7914 7914 F DEBUG : #01 pc 0003ecb7 /apex/com.android.runtime/lib/bionic/libc.so (ioctl+26) (BuildId: 065f5a7b98a8052e1c4af4e537983a0f)
03-15 16:21:05.433 7914 7914 F DEBUG : #02 pc 00053315 /apex/com.android.vndk.v31/lib/libhidlbase.so (android::hardware::IPCThreadState::getAndExecuteCommand()+144) (BuildId: 61a23abb570cc7f6a181c54d7bd04c68)
03-15 16:21:05.433 7914 7914 F DEBUG : #03 pc 000531e5 /apex/com.android.vndk.v31/lib/libhidlbase.so (android::hardware::IPCThreadState::joinThreadPool(bool)+56) (BuildId: 61a23abb570cc7f6a181c54d7bd04c68)
03-15 16:21:05.433 7914 7914 F DEBUG : #04 pc 000025fd /vendor/bin/hw/android.hardware.audio.service-droidlogic (main+2468) (BuildId: cee1ecdb908a8f9def6b7234d77457b6)
03-15 16:21:05.433 7914 7914 F DEBUG : #05 pc 00031f9d /apex/com.android.runtime/lib/bionic/libc.so (__libc_init+56) (BuildId: 065f5a7b98a8052e1c4af4e537983a0f)

Thanks,
Peter

(ALTech) JunGyu Kim

2023-03-16 12:43

developer   ~0012552

Hi Peter,

I reproduced this issue as below.
1. Pairing with BA01 RCU
2. Reboot STB
3. Try voice recognition by pressing the NUGU key -> Crash occurs in 2~3 times

Please check attached log and tombstone files.

Thank you.
Kim

(ALTech) Younkwang Jung

2023-03-17 12:42

developer   ~0012566

Hi Peter

This issue is urgent.
Please check it quickly.

Thank you
YK.Jung

(SW) Peter Chen

2023-03-20 14:44

developer   ~0012576

Hi JunGyu,

I cannot reproduce this issue with your step so far. Is there any step i need to do else ?
Which FW you're using to reproduce it ?
From log, NUGU voice is not work after the audio server crashed.

Thanks,
Peter

(ALTech) JunGyu Kim

2023-03-20 15:59

developer   ~0012577

Hi Peter,

I have tested this issue in FW 15.537.24 / 15.537.26 and I can reproduce both FW.
I repeated Active NUGU <-> exit NUGU.
I also tried voice recognition sometimes.

A reproduction video is attached.
Please refer it.

Thank you.
Kim

(ALTech) JunGyu Kim

2023-03-21 15:31

developer   ~0012596

Hi Peter,

Additionally, I attached video that our QA was reproducing this issue.
At this video, QA tried Channel up / down / Volume up / down / Mute on.
After reboot device, this issue was reproduced.

Please refer it.
Thank you.
Kim
Nugu_X_(4h11m).mp4 (38,363,569 bytes)   
Nugu_X_(4h11m)_logcat.log (13,527,027 bytes)

(ALTech) Younkwang Jung

2023-03-22 15:05

developer   ~0012608

Hi Peter

This issue ( priority is major) is continuously being reproduced.
Due to the ATV12 schedule, the major issue of ATV12 needs to be resolved quickly.
BPM-16462 / BPM-16171

Please check it quickly

Thank you
YK.Jung

(ALTech) Younkwang Jung

2023-03-23 12:59

developer   ~0012612

Hi Peter

This issue is observed only in the FXN model.
Please let me know the schedule by when it will be resolved.

Thank you
YK.Jung

(SW) Peter Chen

2023-03-23 15:12

developer   ~0012614

Hi YK / JunGyu,

According to the video attached, I still cannot reproduce this issue on my side.
Can you help to try with the test image and share the log?

image path : /release_by_fxn/smart3_test/nugu

Thanks,
Peter

(ALTech) JunGyu Kim

2023-03-23 16:07

developer   ~0012616

Hi Peter,

I have attached the video and log reproduced in the test FW.
Please check it.

Thank you.
Kim
Nugu_X_logcat.log (18,613,506 bytes)
Nugu_X_logcat.mp4 (42,982,327 bytes)   

(ALTech) JunGyu Kim

2023-03-24 10:46

developer   ~0012630

Hi Peter

Additionally, our QA tested in FW 15.537.281 (applying NUGU patch from amlogic) but still reproduced this issue.
At this FW, when NUGU recognition does not work, live screen is stopped.

I also added video and log file.

Thank you,
Kim
NUGU_X_15.537.281_logcat.log (8,141,009 bytes)

(SW) Peter Chen

2023-03-24 17:26

developer   ~0012637

Hi JunGyu,

Please help to try with the test image again and share the log.

Path : /release_by_fxn/smart3_test/nugu/k000c-537r29_SD-20230324-BFX-AT100

Thanks,
Peter

(ALTech) JunGyu Kim

2023-03-27 11:52

developer   ~0012644

Hi Peter

Below is the result of testing in your test FW.

1. The volume is not controlled in AC3 channel by "B tv" setting.
2. After reboot, the first NUGU recognition is not work. From the second, NUGU recognition is work well.
    (Sometimes second NUGU recognition is NOT work.)

I attached two log files.

Thank you.
Kim
1st_2nd_NUGUX_logcat.log (5,046,824 bytes)

(SW) Peter Chen

2023-03-27 14:51

developer   ~0012650

Hi JunGyu,

From the log, the audio input device is correct and the audio server is not crashed.
Can you check it again?

03-27 11:30:19.160 3438 3438 D audio_hw_primary: adev_open_input_stream: enter: devices(0x80000010) channel_mask(0x10) rate(16000) format(0x1) source(20)
03-27 11:30:30.095 3438 3481 D audio_hw_primary: adev_open_input_stream: enter: devices(0x80000010) channel_mask(0x10) rate(16000) format(0x1) source(20)
03-27 11:30:38.131 3438 3481 D audio_hw_primary: adev_open_input_stream: enter: devices(0x80000010) channel_mask(0x10) rate(16000) format(0x1) source(20)

Thanks,
Peter

(ALTech) JunGyu Kim

2023-03-27 16:31

developer   ~0012653

Hi Peter,

I have test it with your FW again and after reboot device, this issue was reproduced.
At the log, crash is occurring.
Please refer this log.

Thank you.
Kim

(SW) Peter Chen

2023-03-28 14:26

developer   ~0012663

Last edited: 2023-03-28 14:27

Hi JunGyu,

1. Can you help to confirm NUGU is work well before audio server crashed?
(From the log, audio input device is correct and NUGU open/close normally before audio server crashed.)

2. Can you try to not playing Live TV when boot up and test again?

Thanks,
Peter

(ALTech) JunGyu Kim

2023-03-28 18:17

developer   ~0012665

Last edited: 2023-03-28 18:24

Hi Peter,

I have verified the last log, this log is tested in another FW. Please forget this log. (BFX-AT100_20230327-172558.zip)

At the result of testing, the crash is not occurring but NUGU recognition is not working first time.
I think this issue caused by something else.

BTW, I reported two issue. Did you check the log of first issue? (the volume is not controlled in AC3 channel by "B tv" setting)

Thank you.
Kim

(SW) Peter Chen

2023-03-29 12:24

developer   ~0012669

Hi JunGyu,

Thanks for your confirmation. Can you try to not playing Live TV when boot up and test again?

I will check again the result log of testing. I think we can focus on the NUGU issue.

Thanks,
Peter

(ALTech) Younkwang Jung

2023-03-29 18:13

developer   ~0012673

Hi Peter

The following issue were observed during the test with the FW you sent.
(/release_by_fxn/smart3_test/nugu/k000c-537r29_SD-20230324-BFX-AT100)

When a nugu issue occurs
- NUGU function do not work when RCU's NUGU button is pressed
- About RCU' LED
  In this state, the LED on the RCU are not on and are off.
  (Originally, if user press a NUGU button, the LED should be on for a certain period of time, but the LED turns off immediately when it is in an issue state )
- About GA test at issue state
  But GA is operating normally. (the GA normally has LEDs on for a certain period of time.)
- The same issue is observed when the STB is plug power off and on.
- This issue seems to disappear when I re-pair RCU and STB.
  
I'm attaching two log files, so please check it

ok case after rebooting : BFX-AT100_20230328-193626_NUGU_ok.zip
issue case after rebooting : BFX-AT100_20230329-160318_NUGU_issue.zip

Thank you
YK.Jung

(ALTech) Younkwang Jung

2023-03-30 16:31

developer   ~0012687

Hi Peter

SKT's opinion is that NUGU has no problem when the issue occurs.
Please update the progress.

Thank you
YK.Jung

(SW) Peter Chen

2023-03-30 17:49

developer   ~0012688

Hi YK,

Can you help to check the RCU firmware version?

Thanks,
Peter

(ALTech) Younkwang Jung

2023-03-31 17:58

developer   ~0012695

Hi Peter

This issue is observed in BA01/BA02 RCU.
version of BMM BA01 is 02.00
version of BMM BA02 is 1.5

Thanks
YK.Jung

(ALTech) Younkwang Jung

2023-03-31 18:42

developer   ~0012698

Hi Peter

This issue must be resolved next week.
But I know that you have a day off early next week.
So please commit the code that you think is stable by today.
We will proceed with the additional test with the final Smart3 bitbucket FW.

Thank you
YK.Jung

(SW) Peter Chen

2023-03-31 19:02

developer   ~0012701

Hi YK,

Sorry, can you share how to check the RCU firmware version?
BTW, the test FW I provided before only add some debug logs.

Thanks,
Peter

(ALTech) Younkwang Jung

2023-03-31 20:18

developer   ~0012702

Hi Peter

1) press * 7 8 9 9 #
2) Please check the attached screen.
     This is an example screen. the rcu version I tested was 02.00.

"리모컨 버젼" : rcu's FW version

Thanks you
YK.Jung
image.png (61,969 bytes)   
image.png (61,969 bytes)   

(ALTech) Younkwang Jung

2023-03-31 20:39

developer   ~0012703

Hi Peter

Now this NUGU issue is Major priority. it's a serious situation.
please let me know if you have any doubts.
We will check it during your day off.

Thank you
YK.Jung

(ALTech) JunGyu Kim

2023-04-06 09:08

developer   ~0012729

Hi Peter,

I have attached the reproduced log from your test FW (15.537.29)
Symptoms are the same as youngkwang jung's comment.

The log attached this time is the log when the first tombstone occurs.
For reference, the RCU version is 02.00.

The reproduction scenario is same as the video delivered earlier,
Channel up / down, volume up / down -> It occurred after cold booting but the reproducibility is low.

At the time of the issue, NUGU is active on the screen, but the green LED on the RCU is not on.

Please check this log.

Thank you.
Kim
20230406_NUGU_crash.zip (927,418 bytes)

(ALTech) JunGyu Kim

2023-04-06 13:50

developer   ~0012739

Hi Peter,

I have found some strange things.
At the log of today I attached, I pressed NUGU and EXIT key but "snd_atvr_pcm_trigger " is not called.

// Normal case
04-06 14:16:18.670 0 0 W : [ 162.652845@2]- key down 194 <<---- input NUGU key
04-06 14:16:18.758 0 0 I [ 162.741549@1]- snd_atvr_pcm_open: 1363
04-06 14:16:18.758 0 0 I [ 162.741558@1]- snd_atvr_pcm_open: 1390
04-06 14:16:18.759 0 0 I [ 162.741614@1]- snd_atvr_pcm_hw_params: 1347
04-06 14:16:18.759 0 0 I [ 162.741656@1]- snd_atvr_pcm_prepare: 1301
04-06 14:16:18.759 0 0 I [ 162.741661@1]- snd_atvr: snd_atvr_pcm_prepare, rate = 16000, period_size = 160, buffer_size = 640
04-06 14:16:18.766 0 0 W [ 162.741722@0]- snd_atvr_pcm_prepare: open mic
04-06 14:16:18.766 0 0 I [ 162.741734@0]d snd_atvr: snd_atvr_pcm_trigger starting audio
04-06 14:16:18.767 0 0 I [ 162.741736@0]d snd_atvr_pcm_trigger: 1257
04-06 14:16:18.767 0 0 I [ 162.741738@0]d snd_atvr_timer_start: 1216

04-06 14:16:22.302 0 0 W : [ 166.284223@1]- key down 174 <<---- input EXIT key
04-06 14:16:22.435 0 0 I [ 166.417573@2]d snd_atvr: snd_atvr_pcm_trigger stopping audio, peak = 1506, # packets = 306
04-06 14:16:22.505 0 0 I [ 166.417581@2]d snd_atvr_timer_stop: 1235
04-06 14:16:22.505 0 0 I [ 166.417589@2]- snd_atvr_pcm_hw_free: 1354
04-06 14:16:22.505 0 0 I : [ 166.417625@2]- snd_atvr_pcm_close
04-06 14:16:22.505 0 0 I [ 166.417628@2]- snd_atvr_timer_stop: 1235



// Abnormal case
04-06 09:54:04.262 0 0 W : [ 55.206567@0]- key down 194 <<---- input NUGU key
04-06 09:54:04.437 0 0 I [ 55.378336@3]- snd_atvr_pcm_open: 1363
04-06 09:54:04.437 0 0 I [ 55.378344@3]- snd_atvr_pcm_open: 1390
04-06 09:54:04.437 0 0 I [ 55.378397@3]- snd_atvr_pcm_hw_params: 1347
04-06 09:54:04.437 0 0 I [ 55.378439@3]- snd_atvr_pcm_prepare: 1301
04-06 09:54:04.437 0 0 I [ 55.378444@3]- snd_atvr: snd_atvr_pcm_prepare, rate = 16000, period_size = 160, buffer_size = 640
04-06 09:54:04.448 0 0 W [ 55.378473@3]- snd_atvr_pcm_prepare: open mic
04-06 09:54:04.448 0 0 I [ 55.378481@3]d snd_atvr: snd_atvr_pcm_trigger starting audio
04-06 09:54:04.448 0 0 I [ 55.378484@3]d snd_atvr_pcm_trigger: 1257
04-06 09:54:04.448 0 0 I [ 55.378485@3]d snd_atvr_timer_start: 1216

04-06 09:54:14.247 0 0 W : [ 65.188344@3]- key down 174 <<---- input EXIT key. At this time, "snd_atvr_pcm_trigger" is not calling.
04-06 09:54:14.968 5713 5713 D UPNSService: [UPNSService] Handling crashed service: true
04-06 09:54:15.115 0 0 W : [ 66.058698@3]- key down 194
04-06 09:54:17.060 0 0 W : [ 68.000758@3]- key down 194 <<---- input NUGU key
04-06 09:54:18.710 0 0 I [ 69.653087@3]d snd_atvr: snd_atvr_pcm_trigger stopping audio, peak = -32768, # packets = 0
04-06 09:54:19.200 0 0 I [ 69.653093@3]d snd_atvr_timer_stop: 1235 <<---- At this time, snd_atvr_pcm_trigger is called. It seems to be called by timer_stop.
04-06 09:54:19.200 0 0 I [ 69.653099@3]- snd_atvr_pcm_hw_free: 1354
04-06 09:54:19.261 0 0 I [ 69.653106@3]- snd_atvr_timer_stop: 1235

I thkin this seems to be the problem, please check it and refer to it when analyzing the log.

Thank you.
Kim

(SW) Peter Chen

2023-04-10 14:16

developer   ~0012775

Hi JunGyu,

Can you try to use the below test image to check again?
Path : /release_by_fxn/smart3_test/nugu/k000c-537r37_SD-20230410-BFX-AT100

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-10 15:53

developer   ~0012778

Hi Peter,

I attached log file from test FW when the crash has been occurred.
Please check it.

Thank you.
Kim

(SW) Peter Chen

2023-04-11 17:48

developer   ~0012816

Hi JunGyu,

Can you help to get more log with below steps?

1.
adb reboot bootloader
fastboot flashing unlock
fastboot reboot
// need to open adb again manually
adb root
adb disable-verity
adb reboot
adb root
adb remount
adb push bt_stack.conf /system/etc/bluetooth

2. Settings -> Developer options -> Enable Bluetooth HCI snoop log
    ==> set "enabled"

And then reboot device to get the log.

Thanks,
Peter
bt_stack.conf (1,952 bytes)   
# Enable trace level reconfiguration function
# Must be present before any TRC_ trace level settings
TraceConf=true

# Trace level configuration
#   BT_TRACE_LEVEL_NONE    0    ( No trace messages to be generated )
#   BT_TRACE_LEVEL_ERROR   1    ( Error condition trace messages )
#   BT_TRACE_LEVEL_WARNING 2    ( Warning condition trace messages )
#   BT_TRACE_LEVEL_API     3    ( API traces )
#   BT_TRACE_LEVEL_EVENT   4    ( Debug messages for events )
#   BT_TRACE_LEVEL_DEBUG   5    ( Full debug messages )
#   BT_TRACE_LEVEL_VERBOSE 6    ( Verbose messages ) - Currently supported for TRC_BTAPP only.
TRC_BTM=6
TRC_HCI=6
TRC_L2CAP=6
TRC_RFCOMM=6
TRC_OBEX=6
TRC_AVCT=6
TRC_AVDT=6
TRC_AVRC=6
TRC_AVDT_SCB=6
TRC_AVDT_CCB=6
TRC_A2D=6
TRC_SDP=6
TRC_SMP=6
TRC_BTAPP=6
TRC_BTIF=6
TRC_BNEP=6
TRC_PAN=6
TRC_HID_HOST=6
TRC_HID_DEV=6

# This is Log configuration for new C++ code using LOG() macros.
# See libchrome/base/logging.h for description on how to configure your logs.
# sample configuration:
#LoggingV=--v=0
#LoggingVModule=--vmodule=*/btm/*=1,btm_ble_multi*=2,btif_*=1

# PTS testing helpers

# Secure connections only mode.
# PTS_SecurePairOnly=true

# Disable LE Connection updates
#PTS_DisableConnUpdates=true

# Disable BR/EDR discovery after LE pairing to avoid cross key derivation errors
#PTS_DisableSDPOnLEPair=true

# SMP Pair options (formatted as hex bytes) auth, io, ikey, rkey, ksize
#PTS_SmpOptions=0xD,0x4,0xf,0xf,0x10

# PTS AVRCP Test mode
#PTS_AvrcpTest=true

# SMP Certification Failure Cases
# Set any of the following SMP error values (from smp_api_types.h)
# to induce pairing failues for various PTS SMP test cases.
# Setting PTS_SmpFailureCase to 0 means normal operation.
# Failure modes:
#
#  SMP_PASSKEY_ENTRY_FAIL = 1
#  SMP_PAIR_AUTH_FAIL = 3
#  SMP_CONFIRM_VALUE_ERR = 4
#  SMP_PAIR_NOT_SUPPORT = 5
#  SMP_PAIR_FAIL_UNKNOWN = 8
#  SMP_REPEATED_ATTEMPTS = 9
#  SMP_NUMERIC_COMPAR_FAIL = 12
#PTS_SmpFailureCase=0

bt_stack.conf (1,952 bytes)   

(ALTech) JunGyu Kim

2023-04-12 12:28

developer   ~0012822

Hi Peter,

I attached as you requested logs.
Please check it.
Thank you
Kim
20230412_logs.zip (1,281,800 bytes)

(ALTech) JunGyu Kim

2023-04-12 13:01

developer   ~0012823

Hi Peter,

I think I found the issue reproduce step.

1. Pairing RCU
2. Battery off -> on the RCU
3. re-pairing RCU by pressing * + #
4. Check NUGU is working
5. AC off -> ON
6. After booting the STB, press NUGU key, the LED is not on the RCU
7. After some time, the crash is occurriing.

As you know that the ATV12 has RCU disconecting issue (https://mantis.cnsbg.foxconn.com/vaas/view.php?id=1702)
At the log I attached above, RCU is disconnected after reboot. So I re-pairing the RCU.
After reboot device, the crash is occurring.

I attached the log when RCU is disconnecting.
You can check btsnoop log through above log file(20230412_logs/logs/btsnoop_hci.log.last)
Please refer it.

Thank you.
Kim

(SW) Peter Chen

2023-04-12 14:27

developer   ~0012825

Hi JunGyu,

Thanks. I'll check if i can reproduce this issue by your step.

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-13 17:42

developer   ~0012850

Hi Peter,

Please let me know if any update.

Thank you.
Kim

(SW) Peter Chen

2023-04-13 18:33

developer   ~0012851

Hi JunGyu,

I cannot reproduce this issue so far, but i will keep to try it.
BTW, Please try the test image below

Path : /release_by_fxn/smart3_test/nugu/k000c-537r37_SD-20230413-BFX-AT100

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-14 08:38

developer   ~0012855

Hi Peter,

It is still being reproduced in the test FW.
I attached log file.

Thank you
Kim
20230414.zip (618,884 bytes)

(SW) Peter Chen

2023-04-14 14:10

developer   ~0012867

Hi JunGyu,

Can you help to check with these two ways?

1. type this command and check it
adb root
adb shell pm disable com.google.android.tv.remote.service

2. check with the test image
Path : /release_by_fxn/smart3_test/nugu/k000c-537r39_SD-20230414-BFX-AT100

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-14 15:45

developer   ~0012871

Hi Peter,

1. adb shell pm disable com.google.android.tv.remote.service
>> [ALT] I tested both 15.537.38 (release FW) and 15.537.39 (test FW you shared) FW, both FW reproduced same issue after type the command "adb shell pm disable com.google.android.tv.remote.service".

2. check with the test image
>> [ALT] It is still being reproduced in the test FW. I attached log file.

Additionally, I attached reproduce video.
Please refer it.

Thank you
Kim
20230414_2.zip (1,023,875 bytes)

(SW) Peter Chen

2023-04-14 18:20

developer   ~0012872

Hi JunGyu,

Sorry for the inconvenience, please help to try again with below.

1. type this command and check it
adb root
adb shell pm uninstall --user 0 -k com.google.android.katniss

2. check with the test image (I have uploaded a new image)
Path : /release_by_fxn/smart3_test/nugu/k000c-537r39_SD-20230414-BFX-AT100

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-17 09:39

developer   ~0012876

Hi Peter,

1. The issue is still reproducing after type the command "adb shell pm uninstall --user 0 -k com.google.android.katniss".
2. It is still being reproduced in the test FW. I attached log file.

Thank you.
Kim
20230417.zip (1,507,286 bytes)

(ALTech) Younkwang Jung

2023-04-17 14:44

developer   ~0012889

Hi Peter

This issue has not been resolved for too long.
It must be resolved by this week. (4/21)

Thank you
YK.Jung

(SW) Peter Chen

2023-04-17 18:32

developer   ~0012901

Hi JunGyu,

We try to revert one patch and please help to check again.
Path : /release_by_fxn/smart3_test/nugu/k000c-537r39_SD-20230417-BFX-AT100

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-18 10:31

developer   ~0012903

Hi Peter,

As a result of checking 20 times with the reproduction method I mentioned, crash does not occur.
The patch you reverted seems affect this issue.

Can you share what you reverted?

Thank you.
Kim

(ALTech) JunGyu Kim

2023-04-18 10:56

developer   ~0012904

Last edited: 2023-04-18 10:57

Hi Peter,

Additionally, we need to verify this issue with our QA.
But, your FW's voice recognition rate is low. I think the reason is additional log for debug.
Please revert and share only the problem patch based on the latest version.

I will verify it with our QA.

Thank you
Kim

(SW) Peter Chen

2023-04-18 10:59

developer   ~0012905

Hi JunGyu,

It seems like we have similar issue long time ago. (https://mantis.cnsbg.foxconn.com/vaas/view.php?id=1152)
And we revert the patch "HID cache commit (which is enabled by google from android 10)" to fix it.

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-18 13:53

developer   ~0012912

Hi Peter,

Thank you for explain.
Please share the FW that as I mentioned above. I will test it with our QA.

Thank you.
Kim

(SW) Peter Chen

2023-04-18 15:18

developer   ~0012914

Hi JunGyu,

Please check it
Path : /release_by_fxn/smart3_test/nugu/k000c-537r39_SD-20230418-BFX-AT100

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-19 15:03

developer   ~0012917

Hi Peter,

Our QA reproduced NUGU recognition is not working issue with the reproduction method I mentioned in your FW.
I verified log and the crash is not occurring. It seems another issue.

I attached video and logcat first. Please check it urgently.
I will check logcat also.

Note that after reboot device, NUGU recognition is working well.

Thank you
Kim
NUGU_X_logcat_20230419.log (6,117,901 bytes)

(SW) Peter Chen

2023-04-20 09:23

developer   ~0012921

Hi JunGyu,

Can you help to check the green LED on the RCU is on or not?

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-20 12:47

developer   ~0012928

Last edited: 2023-04-20 12:53

Hi Peter,

We are currently retry this issue but the issue is not reproducing. I will let you know if this issue reproduce again.
Is there any strange log?

BTW, I think the patch "HID cache commit (which is enabled by google from android 10)" must be include because this patch already applied to ATV10.
Please commit this patch.

Thank you.
Kim

(SW) Peter Chen

2023-04-20 13:56

developer   ~0012930

Hi JunGyu,

I don't find any strange log.
If you reproduced it again, please check the LED on the RCU and get the fail log.

OK, I applied the patch "HID cache commit" on ATV12.

Thanks,
Peter

(ALTech) JunGyu Kim

2023-04-21 15:49

developer   ~0012952

Hi Peter,

I and our QA tested over 100 times but the issue is not reproduced. So I close this issue.
If this issue is reproduce, I will reopen this issue.

Thank you for the support.
Kim

Issue History

Date Modified Username Field Change
2023-03-02 15:39 (ALTech) JunGyu Kim New Issue
2023-03-02 15:39 (ALTech) JunGyu Kim Status new => assigned
2023-03-02 15:39 (ALTech) JunGyu Kim Assigned To => (SW) Kerwin Chen
2023-03-02 15:40 (ALTech) JunGyu Kim Issue Monitored: (ALTech) SY Yoon
2023-03-02 15:40 (ALTech) JunGyu Kim Issue Monitored: (ALTech) Younkwang Jung
2023-03-02 18:21 (SW) Kerwin Chen Assigned To (SW) Kerwin Chen => (SW) Peter Chen
2023-03-02 18:59 (ALTech) JunGyu Kim Note Added: 0012410
2023-03-02 18:59 (ALTech) JunGyu Kim Note Edited: 0012410
2023-03-03 11:43 (SW) Peter Chen Note Added: 0012416
2023-03-03 13:58 (ALTech) JunGyu Kim Note Added: 0012423
2023-03-03 13:58 (ALTech) JunGyu Kim File Added: NUGU_Not_Working.log
2023-03-03 13:58 (ALTech) JunGyu Kim File Added: NUGU_Working.log
2023-03-06 14:53 (ALTech) Younkwang Jung Priority normal => high
2023-03-08 10:31 (ALTech) JunGyu Kim Note Added: 0012462
2023-03-13 16:13 (SW) Peter Chen Assigned To (SW) Peter Chen => (ALTech) JunGyu Kim
2023-03-13 16:13 (SW) Peter Chen Status assigned => resolved
2023-03-13 16:13 (SW) Peter Chen Note Added: 0012516
2023-03-14 12:11 (ALTech) JunGyu Kim Note Added: 0012525
2023-03-14 16:43 (SW) Peter Chen Note Added: 0012532
2023-03-15 15:42 (ALTech) JunGyu Kim Note Added: 0012541
2023-03-15 15:42 (ALTech) JunGyu Kim File Added: NUGU_not_working2_logcat.log
2023-03-15 15:43 (ALTech) JunGyu Kim Assigned To (ALTech) JunGyu Kim => (SW) Peter Chen
2023-03-15 15:43 (ALTech) JunGyu Kim Status resolved => feedback
2023-03-15 15:43 (ALTech) JunGyu Kim Resolution open => reopened
2023-03-16 10:23 (SW) Peter Chen Note Added: 0012550
2023-03-16 12:43 (ALTech) JunGyu Kim Note Added: 0012552
2023-03-16 12:43 (ALTech) JunGyu Kim File Added: BFX-AT100_20230316-133712.zip
2023-03-16 12:43 (ALTech) JunGyu Kim Status feedback => assigned
2023-03-17 12:42 (ALTech) Younkwang Jung Note Added: 0012566
2023-03-20 14:44 (SW) Peter Chen Note Added: 0012576
2023-03-20 15:59 (ALTech) JunGyu Kim Note Added: 0012577
2023-03-20 15:59 (ALTech) JunGyu Kim File Added: KakaoTalk_20230320_165639313.mp4
2023-03-21 15:31 (ALTech) JunGyu Kim Note Added: 0012596
2023-03-21 15:31 (ALTech) JunGyu Kim File Added: Nugu_X_(4h11m).mp4
2023-03-21 15:31 (ALTech) JunGyu Kim File Added: Nugu_X_(4h11m)_logcat.log
2023-03-22 15:05 (ALTech) Younkwang Jung Note Added: 0012608
2023-03-22 15:05 (ALTech) Younkwang Jung Priority high => urgent
2023-03-23 12:59 (ALTech) Younkwang Jung Note Added: 0012612
2023-03-23 15:12 (SW) Peter Chen Note Added: 0012614
2023-03-23 16:07 (ALTech) JunGyu Kim Note Added: 0012616
2023-03-23 16:07 (ALTech) JunGyu Kim File Added: Nugu_X_logcat.log
2023-03-23 16:07 (ALTech) JunGyu Kim File Added: Nugu_X_logcat.mp4
2023-03-24 10:46 (ALTech) JunGyu Kim Note Added: 0012630
2023-03-24 10:46 (ALTech) JunGyu Kim File Added: NUGU_X_15.537.281.mp4
2023-03-24 10:46 (ALTech) JunGyu Kim File Added: NUGU_X_15.537.281_logcat.log
2023-03-24 17:26 (SW) Peter Chen Note Added: 0012637
2023-03-27 11:52 (ALTech) JunGyu Kim Note Added: 0012644
2023-03-27 11:52 (ALTech) JunGyu Kim File Added: 1st_2nd_NUGUX_logcat.log
2023-03-27 11:52 (ALTech) JunGyu Kim File Added: Dolby_volumeNo_(Btv)_logcat2.log
2023-03-27 14:51 (SW) Peter Chen Note Added: 0012650
2023-03-27 16:31 (ALTech) JunGyu Kim Note Added: 0012653
2023-03-27 16:31 (ALTech) JunGyu Kim File Added: BFX-AT100_20230327-172558.zip
2023-03-28 14:26 (SW) Peter Chen Note Added: 0012663
2023-03-28 14:27 (SW) Peter Chen Note Edited: 0012663
2023-03-28 18:17 (ALTech) JunGyu Kim Note Added: 0012665
2023-03-28 18:24 (ALTech) JunGyu Kim Note Edited: 0012665
2023-03-29 12:24 (SW) Peter Chen Note Added: 0012669
2023-03-29 18:13 (ALTech) Younkwang Jung Note Added: 0012673
2023-03-29 18:13 (ALTech) Younkwang Jung File Added: BFX-AT100_20230328-193626_NUGU_ok.zip
2023-03-29 18:13 (ALTech) Younkwang Jung File Added: BFX-AT100_20230329-160318_NUGU_issue.zip
2023-03-30 16:31 (ALTech) Younkwang Jung Note Added: 0012687
2023-03-30 17:49 (SW) Peter Chen Note Added: 0012688
2023-03-31 17:58 (ALTech) Younkwang Jung Note Added: 0012695
2023-03-31 18:42 (ALTech) Younkwang Jung Note Added: 0012698
2023-03-31 19:02 (SW) Peter Chen Note Added: 0012701
2023-03-31 20:18 (ALTech) Younkwang Jung Note Added: 0012702
2023-03-31 20:18 (ALTech) Younkwang Jung File Added: image.png
2023-03-31 20:39 (ALTech) Younkwang Jung Note Added: 0012703
2023-04-06 09:08 (ALTech) JunGyu Kim Note Added: 0012729
2023-04-06 09:08 (ALTech) JunGyu Kim File Added: 20230406_NUGU_crash.zip
2023-04-06 13:50 (ALTech) JunGyu Kim Note Added: 0012739
2023-04-10 14:16 (SW) Peter Chen Note Added: 0012775
2023-04-10 15:53 (ALTech) JunGyu Kim Note Added: 0012778
2023-04-10 15:53 (ALTech) JunGyu Kim File Added: BFX-AT100_20230410-164744.zip
2023-04-11 17:48 (SW) Peter Chen Note Added: 0012816
2023-04-11 17:48 (SW) Peter Chen File Added: bt_stack.conf
2023-04-12 12:28 (ALTech) JunGyu Kim Note Added: 0012822
2023-04-12 12:28 (ALTech) JunGyu Kim File Added: 20230412_logs.zip
2023-04-12 13:01 (ALTech) JunGyu Kim Note Added: 0012823
2023-04-12 13:01 (ALTech) JunGyu Kim File Added: BFX-AT100_20230412-131901.zip
2023-04-12 14:27 (SW) Peter Chen Note Added: 0012825
2023-04-13 17:42 (ALTech) JunGyu Kim Note Added: 0012850
2023-04-13 18:33 (SW) Peter Chen Note Added: 0012851
2023-04-14 08:38 (ALTech) JunGyu Kim Note Added: 0012855
2023-04-14 08:38 (ALTech) JunGyu Kim File Added: 20230414.zip
2023-04-14 14:10 (SW) Peter Chen Note Added: 0012867
2023-04-14 15:45 (ALTech) JunGyu Kim Note Added: 0012871
2023-04-14 15:45 (ALTech) JunGyu Kim File Added: 20230414_163234.mp4
2023-04-14 15:45 (ALTech) JunGyu Kim File Added: 20230414_2.zip
2023-04-14 18:20 (SW) Peter Chen Note Added: 0012872
2023-04-17 09:39 (ALTech) JunGyu Kim Note Added: 0012876
2023-04-17 09:39 (ALTech) JunGyu Kim File Added: 20230417.zip
2023-04-17 14:44 (ALTech) Younkwang Jung Note Added: 0012889
2023-04-17 18:32 (SW) Peter Chen Note Added: 0012901
2023-04-18 10:31 (ALTech) JunGyu Kim Note Added: 0012903
2023-04-18 10:56 (ALTech) JunGyu Kim Note Added: 0012904
2023-04-18 10:57 (ALTech) JunGyu Kim Note Edited: 0012904
2023-04-18 10:59 (SW) Peter Chen Note Added: 0012905
2023-04-18 13:53 (ALTech) JunGyu Kim Note Added: 0012912
2023-04-18 15:18 (SW) Peter Chen Note Added: 0012914
2023-04-19 15:03 (ALTech) JunGyu Kim Note Added: 0012917
2023-04-19 15:03 (ALTech) JunGyu Kim File Added: NUGU_X_20230419.mp4
2023-04-19 15:03 (ALTech) JunGyu Kim File Added: NUGU_X_logcat_20230419.log
2023-04-20 09:23 (SW) Peter Chen Note Added: 0012921
2023-04-20 12:47 (ALTech) JunGyu Kim Note Added: 0012928
2023-04-20 12:53 (ALTech) JunGyu Kim Note Edited: 0012928
2023-04-20 13:56 (SW) Peter Chen Note Added: 0012930
2023-04-21 15:49 (ALTech) JunGyu Kim Note Added: 0012952
2023-04-21 15:49 (ALTech) JunGyu Kim Status assigned => closed